[10/14 10:08:15,ArtOfTest.Runner.exe(3416:3)] First trace message from process 3416: "C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=5208
[10/14 10:08:15,ArtOfTest.Runner.exe(3416:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 3764).
[10/14 10:08:15,ArtOfTest.Runner.exe(3416:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[10/14 10:08:16,ArtOfTest.Runner.exe(3416:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[10/14 10:08:16,ArtOfTest.Runner.exe(3416:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 28496).
[10/14 10:08:16,ArtOfTest.Runner.exe(3416:4),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly 'Telerik.TestStudio.DialogMatches.dll' with version '2020.2.910.0' loaded successfully.
[10/14 10:08:16,Telerik.TestingFramework.Hosts.Manager.exe(27128:1)] First trace message from process 27128: "C:\Program Files (x86)\Progress\Test Studio\Bin\Telerik.TestingFramework.Hosts.Manager.exe"
[10/14 10:08:16,Telerik.TestingFramework.Hosts.Manager.exe(27128:1)] First trace message from foreground unnamed thread (managed ID = 1, native ID = 26036).
[10/14 10:08:16,Telerik.TestingFramework.Hosts.Manager.exe(27128:1),WebSocket] HostsManager constructor : Starting a web socket server to communicate with the browser extension...
[10/14 10:08:16,Telerik.TestingFramework.Hosts.Manager.exe(27128:1),WebSocket] HostsManager constructor : A web socket server was successfully started on port: 54781.
[10/14 10:08:16,Telerik.TestingFramework.Hosts.Manager.exe(27128:1),WebSocket] HostsManager constructor : Starting a file server to host the start page of the browser extension...
[10/14 10:08:16,Telerik.TestingFramework.Hosts.Manager.exe(27128:1),WebSocket] HostsManager constructor : A file server to host the start page of the browser extension was successfully started on: http://localhost:54782/webui/.
[10/14 10:08:19,ArtOfTest.Runner.exe(3416:14)] First trace message from pool unnamed thread (managed ID = 14, native ID = 15912).
[10/14 10:08:19,ArtOfTest.Runner.exe(3416:14),Framework] Manager.AddNewBrowser() : Added client ID "32683622-ee58-4129-a3ab-fa1dee619fc6" (now there is/are 1 total).
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '08. Verify Filename Matches Document Title when Downloading from Simple Search', path: 'E2E Tests\17. Documents Tests\17.06 Simple Search Tests\08. Verify Filename Matches Document Title when Downloading from Simple Search.tstest.'
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 15 plugin(s) in folder "C:\Program Files (x86)\Progress\Test Studio\Bin\Plugins".
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ResponsiveWeb.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ScreenRecorderExtension.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:20,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch'"...
[10/14 10:08:21,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch', path: 'E2E Tests\17. Documents Tests\17.00 Action Parts\SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch.tstest.'
[10/14 10:08:21,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:08:21,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:21,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:21,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:21,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : ''"...
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:13)] First trace message from pool unnamed thread (managed ID = 13, native ID = 24248).
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:13),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:23.259 - [INFO - CONTENT SCRIPTS] Initialized.
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:9)] First trace message from pool unnamed thread (managed ID = 9, native ID = 18584).
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:9),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:23.284 - [INFO - CONTENT SCRIPTS] Page script: scripts/pageScript.js loaded in document.body
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : ''".
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:23,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "IF (Verify Exists 'DocumentsLink')"...
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "IF (Verify Exists 'DocumentsLink')".
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'Login As CSI Admin.tstest'"...
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login As CSI Admin', path: 'E2E Tests\01. Login & Logout Tests\1.01 Login Tests\Login As CSI Admin.tstest.'
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/'"...
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:13),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:24.461 - [INFO - CONTENT SCRIPTS] Initialized.
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:9),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:24.476 - [INFO - CONTENT SCRIPTS] Page script: scripts/pageScript.js loaded in document.body
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/'".
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "IF (Verify Exists 'EmailEmail')"...
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "IF (Verify Exists 'EmailEmail')".
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:24,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'SubStep - CSIAdminLogin'"...
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SubStep - CSIAdminLogin', path: 'E2E Tests\01. Login & Logout Tests\1.00 Action Pieces\SubStep - CSIAdminLogin.tstest.'
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Wait for 'EmailEmail' is enabled"...
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Wait for 'EmailEmail' is enabled".
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'EmailEmail'"...
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'EmailEmail'".
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:25,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'csi-admin@csiweb.com' in 'EmailEmail'"...
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'csi-admin@csiweb.com' in 'EmailEmail'".
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Keyboard (KeyPress) - Tab (1 times) on 'EmailEmail'"...
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Keyboard (KeyPress) - Tab (1 times) on 'EmailEmail'".
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text '*****' in 'PasswordPassword'"...
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text '*****' in 'PasswordPassword'".
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Wait for 'LogInButtonTag' is enabled"...
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Wait for 'LogInButtonTag' is enabled".
[10/14 10:08:26,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:27,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:27,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:27,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:27,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:27,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'LogInButtonTag'"...
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:9),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:28.279 - [INFO - CONTENT SCRIPTS] Initialized.
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:13),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:28.331 - [INFO - CONTENT SCRIPTS] Page script: scripts/pageScript.js loaded in document.body
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'LogInButtonTag'".
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'SubStep - CSIAdminLogin'".
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "'Maximize' the browser."...
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "'Maximize' the browser.".
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Wait for Exists 'CSIImage'"...
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Wait for Exists 'CSIImage'".
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait for 'CSIImage' is enabled"...
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait for 'CSIImage' is enabled".
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'Login As CSI Admin.tstest'".
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:28,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'DocumentsLink'"...
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'DocumentsLink'".
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Click 'SearchLink'"...
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:13),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:29.681 - [INFO - CONTENT SCRIPTS] Initialized.
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:9),WebExtension] ExecutionMediator.OnMessageReceived() : 14/10/2020 10:08:29.726 - [INFO - CONTENT SCRIPTS] Page script: scripts/pageScript.js loaded in document.body
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Click 'SearchLink'".
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch'".
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:29,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Wait for 'SimpleSearchKeyText' is enabled"...
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Wait for 'SimpleSearchKeyText' is enabled".
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Wait for 'SearchButton' is enabled"...
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Wait for 'SearchButton' is enabled".
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait for 'Text' is enabled"...
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait for 'Text' is enabled".
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:30,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text 'Honn, Maria' in 'SimpleSearchKeyText'"...
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text 'Honn, Maria' in 'SimpleSearchKeyText'".
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:32,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'SearchButton'"...
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'SearchButton'".
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Wait for 'DocumentsGridDiv' is enabled"...
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Wait for 'DocumentsGridDiv' is enabled".
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:33,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Extract 'TextContent' on 'x20191216Link' into DataBindVariable $(documentTitleVar)"...
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Extract 'TextContent' on 'x20191216Link' into DataBindVariable $(documentTitleVar)".
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Wait for 'DownloadButtonForDoc' is enabled"...
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Wait for 'DownloadButtonForDoc' is enabled".
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Verify the Filename Matches the Document Title'"...
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Uncategorized] Log.WriteLineInternal() : Document Title from extracted step is: ToBeEdited HONN, MARIA
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),Uncategorized] Log.WriteLineInternal() : Filename: ToBeEditedHONN,MARIA
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.AddDialog() : Added {GenericDialog(Dismiss=CLOSE)} for monitoring.
[10/14 10:08:34,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {GenericDialog(Dismiss=CLOSE)}.
[10/14 10:08:35,ArtOfTest.Runner.exe(3416:19)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 19, native ID = 15180).
[10/14 10:08:35,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:35,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:35,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:35,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:36,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:36,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:36,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:36,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:36,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:36,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:37,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:37,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:37,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:37,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:37,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:37,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:38,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:38,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:38,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:38,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:38,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:38,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:4),Uncategorized] Log.WriteLineInternal() : Text copied from download dialog: 
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:39,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.RemoveDialogs() : Removing {} from monitoring.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.RemoveDialogs() : Removing {GenericDialog(Dismiss=CLOSE)} from monitoring.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1654020575" in order to unblock and eventually terminate it...
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 16344).
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Framework] Manager.RemoveBrowser() : Removed client ID "32683622-ee58-4129-a3ab-fa1dee619fc6" (0 remaining).
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:4),Warning] Manager.RemoveBrowser() : Attempted to remove a client ID "32683622-ee58-4129-a3ab-fa1dee619fc6" that was not in the list.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OnWindowCreated() : New dialog detected (ClassName = "Chrome_WidgetWin_1", Handle = "10226956").
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),DialogPlayback] DialogMonitor.OutputErrorMessageToLog() : Unexpected dialog encountered. Taking no action.
[10/14 10:08:40,ArtOfTest.Runner.exe(3416:19),Uncategorized] Log.WriteLineInternal() : Unexpected dialog encountered. Taking no action.
[10/14 10:09:03,ArtOfTest.Runner.exe(3416:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/14 10:09:03,ArtOfTest.Runner.exe(3416:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/14 10:09:04,Telerik.TestStudio.exe(19428:49)] First trace message from background thread "Callback Listener Thread" (managed ID = 49, native ID = 17616).
[10/14 10:09:04,Telerik.TestStudio.exe(19428:49),Warning] PipeCommunication.SyncPipeRead() : Pipe was already disposed at the moment of the read attempt.
[10/14 10:09:19,ArtOfTest.Runner.exe(30340:3)] First trace message from process 30340: "C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=5208
[10/14 10:09:19,ArtOfTest.Runner.exe(30340:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 23964).
[10/14 10:09:19,ArtOfTest.Runner.exe(30340:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[10/14 10:09:20,ArtOfTest.Runner.exe(30340:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[10/14 10:09:20,ArtOfTest.Runner.exe(30340:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 29808).
[10/14 10:09:20,ArtOfTest.Runner.exe(30340:4),TestStudio] Manager.GetDialogMatchProvider() : The dialog matches assembly 'Telerik.TestStudio.DialogMatches.dll' with version '2020.2.910.0' loaded successfully.
[10/14 10:09:20,ArtOfTest.Runner.exe(30340:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=26164, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[10/14 10:09:20,ArtOfTest.Runner.exe(30340:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=265768)...
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] Connector.Start() : Process launched (ID=1648, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 23072148").
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] Connector.Start() : Process exited (ID=1648, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Progress\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 23072148").
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] Connector.InjectCode() : ------- Function Start -------
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] Connector.InjectCode() : Connection string: C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2020.2.910.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?19428
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 23072148 on another thread...
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 20360).
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:9),Framework] <>c__DisplayClass27_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 10:09:21,ArtOfTest.Runner.exe(30340:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 23072148 (lpData = "C:\WINDOWS\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2020.2.910.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?19428")...
[10/14 10:09:21,IEXPLORE.EXE(22124:1)] First trace message from process 22124: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:26164 CREDAT:17410 /prefetch:2
[10/14 10:09:21,IEXPLORE.EXE(22124:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 27900).
[10/14 10:09:21,IEXPLORE.EXE(22124:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 23072148, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-84498210", PID = 19428).
[10/14 10:09:21,IEXPLORE.EXE(22124:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 23072148 on same thread...
[10/14 10:09:22,IEXPLORE.EXE(22124:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[10/14 10:09:22,IEXPLORE.EXE(22124:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_c0615a17-e6d4-4e3b-be6a-b8d690068740".
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 22988).
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 16080).
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[10/14 10:09:22,IEXPLORE.EXE(22124:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "8ecae3a9-814d-48b1-bb44-d2e012a40001.CommandPipe", Async = "8ecae3a9-814d-48b1-bb44-d2e012a40001.AsyncPipe"}
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Framework] Connector.InjectCode() : ------- Function End -------
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_c0615a17-e6d4-4e3b-be6a-b8d690068740" (now there is/are 1 total).
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: '08. Verify Filename Matches Document Title when Downloading from Simple Search', path: 'E2E Tests\17. Documents Tests\17.06 Simple Search Tests\08. Verify Filename Matches Document Title when Downloading from Simple Search.tstest.'
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 15 plugin(s) in folder "C:\Program Files (x86)\Progress\Test Studio\Bin\Plugins".
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ResponsiveWeb.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ScreenRecorderExtension.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:22,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch'"...
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch', path: 'E2E Tests\17. Documents Tests\17.00 Action Parts\SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch.tstest.'
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : ''"...
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : ''".
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "IF (Verify Exists 'DocumentsLink')"...
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "IF (Verify Exists 'DocumentsLink')".
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'Login As CSI Admin.tstest'"...
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Login As CSI Admin', path: 'E2E Tests\01. Login & Logout Tests\1.01 Login Tests\Login As CSI Admin.tstest.'
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:23,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/'"...
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/'".
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "IF (Verify Exists 'EmailEmail')"...
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "IF (Verify Exists 'EmailEmail')".
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Execute test 'SubStep - CSIAdminLogin'"...
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SubStep - CSIAdminLogin', path: 'E2E Tests\01. Login & Logout Tests\1.00 Action Pieces\SubStep - CSIAdminLogin.tstest.'
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Wait for 'EmailEmail' is enabled"...
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Wait for 'EmailEmail' is enabled".
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'EmailEmail'"...
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'EmailEmail'".
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'csi-admin@csiweb.com' in 'EmailEmail'"...
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'csi-admin@csiweb.com' in 'EmailEmail'".
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:24,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Keyboard (KeyPress) - Tab (1 times) on 'EmailEmail'"...
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Keyboard (KeyPress) - Tab (1 times) on 'EmailEmail'".
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text '*****' in 'PasswordPassword'"...
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text '*****' in 'PasswordPassword'".
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Wait for 'LogInButtonTag' is enabled"...
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Wait for 'LogInButtonTag' is enabled".
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:25,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Click 'LogInButtonTag'"...
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Click 'LogInButtonTag'".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'SubStep - CSIAdminLogin'".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "'Maximize' the browser."...
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "'Maximize' the browser.".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Wait for Exists 'CSIImage'"...
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Wait for Exists 'CSIImage'".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait for 'CSIImage' is enabled"...
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait for 'CSIImage' is enabled".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'Login As CSI Admin.tstest'".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Click 'DocumentsLink'"...
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Click 'DocumentsLink'".
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:26,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Click 'SearchLink'"...
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Click 'SearchLink'".
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Execute test 'SubStep-AsCSIAdminVerifyDocumentsLinkOrNavigateToSearch'".
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Wait for 'SimpleSearchKeyText' is enabled"...
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Wait for 'SimpleSearchKeyText' is enabled".
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Wait for 'SearchButton' is enabled"...
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Wait for 'SearchButton' is enabled".
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Wait for 'Text' is enabled"...
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Wait for 'Text' is enabled".
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:27,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text 'Honn, Maria' in 'SimpleSearchKeyText'"...
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text 'Honn, Maria' in 'SimpleSearchKeyText'".
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'SearchButton'"...
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'SearchButton'".
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:29,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Wait for 'DocumentsGridDiv' is enabled"...
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Wait for 'DocumentsGridDiv' is enabled".
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Extract 'TextContent' on 'x20191216Link' into DataBindVariable $(documentTitleVar)"...
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Extract 'TextContent' on 'x20191216Link' into DataBindVariable $(documentTitleVar)".
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Wait for 'DownloadButtonForDoc' is enabled"...
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Wait for 'DownloadButtonForDoc' is enabled".
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Verify the Filename Matches the Document Title'"...
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Uncategorized] Log.WriteLineInternal() : Document Title from extracted step is: ToBeEdited HONN, MARIA
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),Uncategorized] Log.WriteLineInternal() : Filename: ToBeEditedHONN,MARIA
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.AddDialog() : Added {IEDownloadDialog(Dismiss=CANCEL)} for monitoring.
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {IEDownloadDialog(Dismiss=CANCEL)}.
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:15)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 15, native ID = 7452).
[10/14 10:09:30,ArtOfTest.Runner.exe(30340:15),DialogPlayback] DialogMonitor.OnWindowCreated() : New dialog detected (ClassName = "#32770", Handle = "9637112").
[10/14 10:09:31,ArtOfTest.Runner.exe(30340:15),DialogPlayback] DialogMonitor.OnWindowCreated() : New dialog detected (ClassName = "Frame Notification Bar", Handle = "9244554").
[10/14 10:09:35,ArtOfTest.Runner.exe(30340:4),Uncategorized] Log.WriteLineInternal() : Text copied from download dialog: 
[10/14 10:09:35,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.RemoveDialogs() : Removing {IEDownloadDialog(Dismiss=CANCEL)} from monitoring.
[10/14 10:09:35,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.RemoveDialogs() : Removing {} from monitoring.
[10/14 10:09:35,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/14 10:09:35,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[10/14 10:09:35,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-84498210" in order to unblock and eventually terminate it...
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_c0615a17-e6d4-4e3b-be6a-b8d690068740" (0 remaining).
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[10/14 10:09:36,IEXPLORE.EXE(22124:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_c0615a17-e6d4-4e3b-be6a-b8d690068740") to pipe server...
[10/14 10:09:36,IEXPLORE.EXE(22124:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/14 10:09:36,ArtOfTest.Runner.exe(30340:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/14 10:09:37,Telerik.TestStudio.exe(19428:63)] First trace message from background thread "Callback Listener Thread" (managed ID = 63, native ID = 968).
[10/14 10:09:37,Telerik.TestStudio.exe(19428:63),Warning] PipeCommunication.SyncPipeRead() : Pipe was already disposed at the moment of the read attempt.
